[INFO] fetching crate codexpad-bridge 1.0.3...
[INFO] testing codexpad-bridge-1.0.3 against master#cced03bfd61a304243a34504618ecec86c17063f for pr-154065-2
[INFO] extracting crate codexpad-bridge 1.0.3 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate codexpad-bridge 1.0.3
[INFO] finished tweaking crates.io crate codexpad-bridge 1.0.3
[INFO] tweaked toml for crates.io crate codexpad-bridge 1.0.3 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate codexpad-bridge 1.0.3 on toolchain cced03bfd61a304243a34504618ecec86c17063f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate codexpad-bridge 1.0.3 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded codexpad-wire v0.3.10
[INFO] [stderr]   Downloaded codexpad-contracts v0.3.8
[INFO] [stderr]   Downloaded rusqlite v0.39.0
[INFO] [stderr]   Downloaded tokio v1.51.0
[INFO] [stderr]   Downloaded libsqlite3-sys v0.37.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0
[INFO] running `Command { std: "docker" "start" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.184
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling cc v1.2.59
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling data-encoding v2.10.0
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling axum-macros v0.5.0
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling fallible-streaming-iterator v0.1.9
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling hashlink v0.11.0
[INFO] [stderr]    Compiling libsqlite3-sys v0.37.0
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling tokio v1.51.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling uuid v1.23.0
[INFO] [stderr]    Compiling tungstenite v0.28.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling codexpad-contracts v0.3.8
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tokio-tungstenite v0.28.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling codexpad-wire v0.3.10
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling rusqlite v0.39.0
[INFO] [stderr]    Compiling codexpad-bridge v1.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 53.97s
[INFO] running `Command { std: "docker" "inspect" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rusqlite v0.39.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling codexpad-bridge v1.0.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 14.86s
[INFO] running `Command { std: "docker" "inspect" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-w" "/opt/rustwide/workdir" "--user" "0:0" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0" "/opt/rustwide/cargo-home/bin/cargo" "+cced03bfd61a304243a34504618ecec86c17063f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.25s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/codexpad_bridge-ac502d740ca24c4e)
[INFO] [stdout] 
[INFO] [stdout] running 69 tests
[INFO] [stdout] test directory::tests::parent_directory_returns_none_for_root ... ok
[INFO] [stdout] test directory::tests::canonicalize_directory_accepts_existing_dir ... ok
[INFO] [stdout] test directory::tests::directory_contains_respects_path_boundaries ... ok
[INFO] [stdout] test manage::tests::build_activate_record_promotes_previous_release ... ok
[INFO] [stdout] test manage::tests::decode_env_value_restores_single_quotes ... ok
[INFO] [stdout] test manage::tests::build_user_service_enforces_process_cleanup_and_memory_accounting ... ok
[INFO] [stdout] test manage::tests::merge_env_values_prefers_overrides_and_defaults ... ok
[INFO] [stdout] test config::tests::resolve_codex_binary_uses_path_entry_when_available ... ok
[INFO] [stdout] test server::tests::build_health_payload_contains_bridge_metadata_and_primary_runtime ... ok
[INFO] [stdout] test server::tests::parse_client_envelope_accepts_plain_hello_payload ... ok
[INFO] [stdout] test manage::tests::build_rollback_record_swaps_current_and_previous ... ok
[INFO] [stdout] test manage::tests::isolated_cargo_config_overrides_user_mirror_replace_with ... ok
[INFO] [stdout] test config::tests::resolve_codex_binary_falls_back_to_common_home_bin ... ok
[INFO] [stdout] test server::tests::parse_client_envelope_rejects_double_encoded_hello_payload ... ok
[INFO] [stdout] test app_server::tests::stop_kills_spawned_process_group ... FAILED
[INFO] [stdout] test app_server::tests::start_sends_initialize_initialized_and_opt_out_notifications ... ok
[INFO] [stdout] test server::tests::get_runtime_status_request_returns_without_hanging ... ok
[INFO] [stdout] test server::tests::health_handler_returns_without_hanging ... ok
[INFO] [stdout] test server::tests::runtime_snapshot_returns_without_hanging ... ok
[INFO] [stdout] test server::tests::list_runtimes_request_returns_without_hanging ... ok
[INFO] [stdout] test server::tests::hello_payload_returns_without_hanging ... ok
[INFO] [stdout] test state::tests::events::app_server_starting_message_updates_status_without_deadlock ... ok
[INFO] [stdout] test server::tests::runtime_summaries_return_without_hanging ... ok
[INFO] [stdout] test state::tests::render_events::approval_review_notifications_upsert_single_render_node ... ok
[INFO] [stdout] test state::tests::events::turn_completed_notification_cleans_up_staged_turn_inputs ... ok
[INFO] [stdout] test state::tests::events::legacy_server_request_is_normalized_into_pending_request ... ok
[INFO] [stdout] test state::tests::events::permissions_server_request_is_persisted_for_clients ... ok
[INFO] [stdout] test state::tests::render_events::command_execution_render_snapshot_preserves_exec_kind ... ok
[INFO] [stdout] test state::tests::text_updates::normalize_name_clears_blank_titles ... ok
[INFO] [stdout] test state::tests::text_updates::resolve_optional_text_update_distinguishes_unchanged_set_and_clear ... ok
[INFO] [stdout] test state::tests::turn_inputs::build_turn_input_items_accepts_text_and_local_image ... ok
[INFO] [stdout] test state::tests::turn_inputs::build_turn_input_items_rejects_non_staged_image_path ... ok
[INFO] [stdout] test state::tests::turn_inputs::send_turn_request_compatibility_supports_legacy_text_and_structured_items ... ok
[INFO] [stdout] test state::tests::render_events::file_change_render_snapshot_preserves_per_file_diff ... ok
[INFO] [stdout] test state::threads::history_tests::build_thread_render_snapshot_marks_last_item_streaming_for_in_progress_turn ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_backfills_missing_aggregated_output ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_does_not_fake_output_for_image_only_function_call_output ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_extracts_text_from_function_call_output_body_arrays ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_falls_back_to_codex_home_when_thread_path_missing ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_inserts_missing_command_before_final_answer ... ok
[INFO] [stdout] test state::threads::history_tests::enrich_thread_history_keeps_existing_aggregated_output ... ok
[INFO] [stdout] test state::threads::reads::tests::materialized_thread_error_triggers_turn_fallback ... ok
[INFO] [stdout] test state::threads::reads::tests::unrelated_thread_read_error_does_not_trigger_fallback ... ok
[INFO] [stdout] test state::tests::events::runtime_exit_downgrades_cached_threads_and_emits_status_change ... ok
[INFO] [stdout] test state::tests::render_events::hook_notifications_upsert_single_render_node ... ok
[INFO] [stdout] test state::threads::tests::thread_list_request_payload_keeps_present_optional_strings ... ok
[INFO] [stdout] test state::threads::tests::thread_list_request_payload_omits_absent_optional_strings ... ok
[INFO] [stdout] test state::threads::thread_response::tests::extract_active_turn_payload_converts_seconds_to_millis ... ok
[INFO] [stdout] test state::tests::render_events::terminal_interaction_notification_preserves_command_and_input ... ok
[INFO] [stdout] test state::tests::render_exec_output::cache_thread_render_snapshot_backfills_missing_exec_output ... ok
[INFO] [stdout] test state::tests::render_events::user_message_render_snapshot_collects_data_urls_without_polluting_text ... ok
[INFO] [stdout] test state::tests::render_exec_output::command_execution_completion_keeps_streamed_output_when_turn_id_changes ... ok
[INFO] [stdout] test state::tests::render_exec_output::command_execution_completion_prefers_final_aggregated_output ... ok
[INFO] [stdout] test state::tests::render_status_surface::active_thread_status_does_not_render_running_surface ... ok
[INFO] [stdout] test state::tests::turn_inputs::stage_input_image_writes_file_under_staging_root ... ok
[INFO] [stdout] test state::tests::render_status_surface::failed_thread_status_uses_error_surface_without_interrupt ... ok
[INFO] [stdout] test state::tests::runtime_fallback::get_runtime_status_falls_back_when_runtime_map_is_blocked ... ok
[INFO] [stdout] test storage::tests::bridge_management_tasks_can_round_trip ... ok
[INFO] [stdout] test state::tests::runtime_fallback::runtime_summaries_for_client_fall_back_when_runtime_map_is_blocked ... ok
[INFO] [stdout] test state::tests::runtime_fallback::list_runtimes_falls_back_when_runtime_map_is_blocked ... ok
[INFO] [stdout] test state::tests::runtime_fallback::runtime_snapshot_for_client_falls_back_when_status_lock_is_blocked ... ok
[INFO] [stdout] test storage::tests::ensure_primary_runtime_refreshes_existing_binary ... ok
[INFO] [stdout] test storage::tests::record_directory_usage_accumulates_use_count ... ok
[INFO] [stdout] test storage::tests::migrate_legacy_workspaces_preserves_existing_bookmarks ... ok
[INFO] [stdout] test state::tests::runtime_fallback::hello_payload_falls_back_when_status_lock_is_blocked ... ok
[INFO] [stdout] test storage::tests::upsert_directory_bookmark_updates_name_without_resetting_created_at ... ok
[INFO] [stdout] test storage::tests::thread_index_migration_removes_legacy_note_column ... ok
[INFO] [stdout] test state::threads::tests::start_thread_falls_back_when_empty_thread_cannot_include_turns ... ok
[INFO] [stdout] test state::threads::tests::resume_thread_and_send_turn_forward_cwd_and_report_effective_cwd ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- app_server::tests::stop_kills_spawned_process_group stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'app_server::tests::stop_kills_spawned_process_group' (954) panicked at src/app_server/tests.rs:170:27:
[INFO] [stdout] 启动 app-server manager 失败: 启动 /tmp/.tmpbSgVI7/fake-codex app-server 失败（runtime=primary cwd=/opt/rustwide/workdir CODEX_HOME=<unset> PATH=/opt/rustwide/cargo-home/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin）
[INFO] [stdout] 
[INFO] [stdout] Caused by:
[INFO] [stdout]     Text file busy (os error 26)
[INFO] [stdout] 
[INFO] [stdout] Stack backtrace:
[INFO] [stdout]    0: <std::io::error::Error as anyhow::context::ext::StdError>::ext_context::<alloc::string::String>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/backtrace.rs:10:14
[INFO] [stdout]    1: <core::result::Result<tokio::process::Child, std::io::error::Error> as anyhow::Context<tokio::process::Child, std::io::error::Error>>::with_context::<alloc::string::String, <codexpad_bridge::app_server::RunningAppServer>::spawn::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/anyhow-1.0.102/src/context.rs:65:37
[INFO] [stdout]    2: <codexpad_bridge::app_server::RunningAppServer>::spawn::{closure#0}
[INFO] [stdout]              at ./src/app_server/running.rs:39:14
[INFO] [stdout]    3: <codexpad_bridge::app_server::AppServerManager>::ensure_started::{closure#0}
[INFO] [stdout]              at ./src/app_server/manager.rs:83:90
[INFO] [stdout]    4: <codexpad_bridge::app_server::AppServerManager>::start::{closure#0}
[INFO] [stdout]              at ./src/app_server/manager.rs:28:31
[INFO] [stdout]    5: codexpad_bridge::app_server::tests::stop_kills_spawned_process_group::{closure#0}
[INFO] [stdout]              at ./src/app_server/tests.rs:170:21
[INFO] [stdout]    6: <core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    7: <core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>> as core::future::future::Future>::poll
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]    8: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]    9: tokio::task::coop::with_budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   10: tokio::task::coop::budget::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   11: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   12: <tokio::runtime::scheduler::current_thread::Context>::enter::<core::task::poll::Poll<()>, <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   13: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   14: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   15: <tokio::runtime::context::scoped::Scoped<tokio::runtime::scheduler::Context>>::set::<<tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   16: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:38
[INFO] [stdout]   17: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::try_with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   18: <std::thread::local::LocalKey<tokio::runtime::context::Context>>::with::<tokio::runtime::context::set_scheduler<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>::{closure#0}, (alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>)>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   19: tokio::runtime::context::set_scheduler::<(alloc::boxed::Box<tokio::runtime::scheduler::current_thread::Core>, core::option::Option<()>), <tokio::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>::{closure#0}>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:17
[INFO] [stdout]   20: <tokio::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio::runtime::scheduler::current_thread::CoreGuard>::block_on<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>::{closure#0}, core::option::Option<()>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   21: <tokio::runtime::scheduler::current_thread::CoreGuard>::block_on::<core::pin::Pin<&mut core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   22: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   23: tokio::runtime::context::runtime::enter_runtime::<<tokio::runtime::scheduler::current_thread::CurrentThread>::block_on<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   24: <tokio::runtime::scheduler::current_thread::CurrentThread>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   25: <tokio::runtime::runtime::Runtime>::block_on_inner::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   26: <tokio::runtime::runtime::Runtime>::block_on::<core::pin::Pin<&mut dyn core::future::future::Future<Output = ()>>>
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   27: codexpad_bridge::app_server::tests::stop_kills_spawned_process_group
[INFO] [stdout]              at ./src/app_server/tests.rs:185:43
[INFO] [stdout]   28: codexpad_bridge::app_server::tests::stop_kills_spawned_process_group::{closure#0}
[INFO] [stdout]              at ./src/app_server/tests.rs:117:44
[INFO] [stdout]   29: <codexpad_bridge::app_server::tests::stop_kills_spawned_process_group::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   30: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   31: test::__rust_begin_short_backtrace::<core::result::Result<(), alloc::string::String>, fn() -> core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   32: test::run_test_in_process::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   33: <core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   34: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   35: std::panicking::catch_unwind::<core::result::Result<(), alloc::string::String>, core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   36: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<test::run_test_in_process::{closure#0}>, core::result::Result<(), alloc::string::String>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   37: test::run_test_in_process
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   38: test::run_test::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   39: test::run_test::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   40: std::sys::backtrace::__rust_begin_short_backtrace::<test::run_test::{closure#1}, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   41: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   42: <core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   43: std::panicking::catch_unwind::do_call::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   44: std::panicking::catch_unwind::<(), core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   45: std::panic::catch_unwind::<core::panic::unwind_safe::AssertUnwindSafe<std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   46: std::thread::lifecycle::spawn_unchecked::<test::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   47: <std::thread::lifecycle::spawn_unchecked<test::run_test::{closure#1}, ()>::{closure#1} as core::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   48: <alloc::boxed::Box<dyn core::ops::function::FnOnce<(), Output = ()> + core::marker::Send> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   49: <std::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]              at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   50: <unknown>
[INFO] [stdout]   51: clone
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b69032f83d8 - std[929523f510218772]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5b69032f83d8 - std[929523f510218772]::backtrace_rs::backtrace::trace_unsynchronized::<std[929523f510218772]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5b69032f83d8 - std[929523f510218772]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5b69032f83d8 - <<std[929523f510218772]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[28b146924c2f19c6]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5b6903310dda - <core[28b146924c2f19c6]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5b6903310dda - core[28b146924c2f19c6]::fmt::write
[INFO] [stdout]    6:     0x5b69032fdc02 - std[929523f510218772]::io::default_write_fmt::<alloc[95e62e727ddf147d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:622:11
[INFO] [stdout]    7:     0x5b69032fdc02 - <alloc[95e62e727ddf147d]::vec::Vec<u8> as std[929523f510218772]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/io/mod.rs:1977:13
[INFO] [stdout]    8:     0x5b69032d3126 - <std[929523f510218772]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5b69032d3126 - std[929523f510218772]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5b69032eefa9 - std[929523f510218772]::panicking::default_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5b690300b0fc - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   12:     0x5b690300b0fc - test[6ec0502068880816]::test_main_inner::<test[6ec0502068880816]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5b69032ef222 - <alloc[95e62e727ddf147d]::boxed::Box<dyn for<'a, 'b> core[28b146924c2f19c6]::ops::function::Fn<(&'a std[929523f510218772]::panic::PanicHookInfo<'b>,), Output = ()> + core[28b146924c2f19c6]::marker::Sync + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::Fn<(&std[929523f510218772]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2291:9
[INFO] [stdout]   14:     0x5b69032ef222 - std[929523f510218772]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5b69032d31e2 - std[929523f510218772]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5b69032c7ad9 - std[929523f510218772]::sys::backtrace::__rust_end_short_backtrace::<std[929523f510218772]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5b69032d42fd - __rustc[acf6043c977865fb]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5b69033115cc - core[28b146924c2f19c6]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5b6903311312 - core[28b146924c2f19c6]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1872:5
[INFO] [stdout]   20:     0x5b6902ec399c - <core[28b146924c2f19c6]::result::Result<(), anyhow[31cb6a6de38747ae]::Error>>::expect
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5b6902fb6494 - codexpad_bridge[bf9b0e9e15163b19]::app_server::tests::stop_kills_spawned_process_group::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/app_server/tests.rs:170:27
[INFO] [stdout]   22:     0x5b6902f161c2 - <core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5b6902f1623d - <core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>> as core[28b146924c2f19c6]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x5b6902f12b2d - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   25:     0x5b6902f129eb - tokio[52bc1b1e4cdd5092]::task::coop::with_budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x5b6902f129eb - tokio[52bc1b1e4cdd5092]::task::coop::budget::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x5b6902f129eb - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   28:     0x5b6902ee3500 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Context>::enter::<core[28b146924c2f19c6]::task::poll::Poll<()>, <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   29:     0x5b6902f11c4d - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   30:     0x5b6902f10ef4 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   31:     0x5b6902c8cd7b - <tokio[52bc1b1e4cdd5092]::runtime::context::scoped::Scoped<tokio[52bc1b1e4cdd5092]::runtime::scheduler::Context>>::set::<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x5b6902c5ef19 - tokio[52bc1b1e4cdd5092]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:38
[INFO] [stdout]   33:     0x5b6902fd3e32 - <std[929523f510218772]::thread::local::LocalKey<tokio[52bc1b1e4cdd5092]::runtime::context::Context>>::try_with::<tokio[52bc1b1e4cdd5092]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   34:     0x5b6902fd39be - <std[929523f510218772]::thread::local::LocalKey<tokio[52bc1b1e4cdd5092]::runtime::context::Context>>::with::<tokio[52bc1b1e4cdd5092]::runtime::context::set_scheduler<(alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   35:     0x5b6902c5562d - tokio[52bc1b1e4cdd5092]::runtime::context::set_scheduler::<(alloc[95e62e727ddf147d]::boxed::Box<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::Core>, core[28b146924c2f19c6]::option::Option<()>), <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context.rs:181:17
[INFO] [stdout]   36:     0x5b6902ee79e0 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>::{closure#0}, core[28b146924c2f19c6]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   37:     0x5b6902ee7cd6 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   38:     0x5b6902f10a00 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   39:     0x5b6902cc5f33 - tokio[52bc1b1e4cdd5092]::runtime::context::runtime::enter_runtime::<<tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x5b6902ee3031 - <tokio[52bc1b1e4cdd5092]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   41:     0x5b6902c8c904 - <tokio[52bc1b1e4cdd5092]::runtime::runtime::Runtime>::block_on_inner::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   42:     0x5b6902c8cacf - <tokio[52bc1b1e4cdd5092]::runtime::runtime::Runtime>::block_on::<core[28b146924c2f19c6]::pin::Pin<&mut dyn core[28b146924c2f19c6]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   43:     0x5b6902fc6ea1 - codexpad_bridge[bf9b0e9e15163b19]::app_server::tests::stop_kills_spawned_process_group
[INFO] [stdout]                                at /opt/rustwide/workdir/src/app_server/tests.rs:185:43
[INFO] [stdout]   44:     0x5b6902fb5c47 - codexpad_bridge[bf9b0e9e15163b19]::app_server::tests::stop_kills_spawned_process_group::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/app_server/tests.rs:117:44
[INFO] [stdout]   45:     0x5b6902ffb976 - <codexpad_bridge[bf9b0e9e15163b19]::app_server::tests::stop_kills_spawned_process_group::{closure#0} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5b6902ffe1db - <fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x5b6902ffe1db - test[6ec0502068880816]::__rust_begin_short_backtrace::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, fn() -> core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:724:18
[INFO] [stdout]   48:     0x5b690300ba55 - test[6ec0502068880816]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:74
[INFO] [stdout]   49:     0x5b690300ba55 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   50:     0x5b690300ba55 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x5b690300ba55 - std[929523f510218772]::panicking::catch_unwind::<core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>, core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x5b690300ba55 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<test[6ec0502068880816]::run_test_in_process::{closure#0}>, core[28b146924c2f19c6]::result::Result<(), alloc[95e62e727ddf147d]::string::String>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x5b690300ba55 - test[6ec0502068880816]::run_test_in_process
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:747:27
[INFO] [stdout]   54:     0x5b690300ba55 - test[6ec0502068880816]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:668:43
[INFO] [stdout]   55:     0x5b69030065b4 - test[6ec0502068880816]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/test/src/lib.rs:698:41
[INFO] [stdout]   56:     0x5b69030065b4 - std[929523f510218772]::sys::backtrace::__rust_begin_short_backtrace::<test[6ec0502068880816]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5b690300ead2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   58:     0x5b690300ead2 - <core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   59:     0x5b690300ead2 - std[929523f510218772]::panicking::catch_unwind::do_call::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5b690300ead2 - std[929523f510218772]::panicking::catch_unwind::<(), core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5b690300ead2 - std[929523f510218772]::panic::catch_unwind::<core[28b146924c2f19c6]::panic::unwind_safe::AssertUnwindSafe<std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5b690300ead2 - std[929523f510218772]::thread::lifecycle::spawn_unchecked::<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   63:     0x5b690300ead2 - <std[929523f510218772]::thread::lifecycle::spawn_unchecked<test[6ec0502068880816]::run_test::{closure#1}, ()>::{closure#1} as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x5b69032f734f - <alloc[95e62e727ddf147d]::boxed::Box<dyn core[28b146924c2f19c6]::ops::function::FnOnce<(), Output = ()> + core[28b146924c2f19c6]::marker::Send> as core[28b146924c2f19c6]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/alloc/src/boxed.rs:2277:9
[INFO] [stdout]   65:     0x5b69032f734f - <std[929523f510218772]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/cced03bfd61a304243a34504618ecec86c17063f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x7cda1512aaa4 - <unknown>
[INFO] [stdout]   67:     0x7cda151b7a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     app_server::tests::stop_kills_spawned_process_group
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 68 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.47s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0", kill_on_drop: false }`
[INFO] [stdout] d8a00c59c63a9bfd0ab99988bd66ca4ae19e551b59d7aaef3cdf81aa06e7c2d0
